[INFO] fetching crate bsv-auth-actix-middleware 0.2.0...
[INFO] testing bsv-auth-actix-middleware-0.2.0 against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] extracting crate bsv-auth-actix-middleware 0.2.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate bsv-auth-actix-middleware 0.2.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate bsv-auth-actix-middleware 0.2.0
[INFO] tweaked toml for crates.io crate bsv-auth-actix-middleware 0.2.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate bsv-auth-actix-middleware 0.2.0 on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate bsv-auth-actix-middleware 0.2.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded hyper-rustls v0.27.8
[INFO] [stderr]   Downloaded openssl-sys v0.9.113
[INFO] [stderr]   Downloaded awc v3.8.2
[INFO] [stderr]   Downloaded openssl v0.10.77
[INFO] [stderr]   Downloaded bsv-sdk v0.2.5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 32b8104ee7103d8067035c4ecfd766d7c4ecd134ea9364a53eccfe62d5facb0c
[INFO] running `Command { std: "docker" "start" "-a" "32b8104ee7103d8067035c4ecfd766d7c4ecd134ea9364a53eccfe62d5facb0c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "32b8104ee7103d8067035c4ecfd766d7c4ecd134ea9364a53eccfe62d5facb0c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "32b8104ee7103d8067035c4ecfd766d7c4ecd134ea9364a53eccfe62d5facb0c", kill_on_drop: false }`
[INFO] [stdout] 32b8104ee7103d8067035c4ecfd766d7c4ecd134ea9364a53eccfe62d5facb0c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6b8964409488016359041b3fb62c007da924af4f19657fee96c01f6e8aa61ce8
[INFO] running `Command { std: "docker" "start" "-a" "6b8964409488016359041b3fb62c007da924af4f19657fee96c01f6e8aa61ce8", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling openssl v0.10.77
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling alloc-no-stdlib v2.0.4
[INFO] [stderr]    Compiling local-waker v0.1.4
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling regex-lite v0.1.9
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling alloc-stdlib v0.2.2
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling brotli-decompressor v5.0.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling actix-utils v3.0.1
[INFO] [stderr]    Compiling time-macros v0.2.27
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling cookie v0.16.2
[INFO] [stderr]    Compiling actix-service v2.0.3
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling bytestring v1.5.0
[INFO] [stderr]    Compiling iri-string v0.7.12
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling local-channel v0.1.5
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling actix-router v0.5.4
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling language-tags v0.3.2
[INFO] [stderr]    Compiling actix-macros v0.2.4
[INFO] [stderr]    Compiling impl-more v0.1.9
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling tokio v1.51.1
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling brotli v8.0.2
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling actix-web-codegen v4.3.0
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rand v0.9.3
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling openssl-sys v0.9.113
[INFO] [stderr]    Compiling zstd-sys v2.0.16+zstd.1.5.7
[INFO] [stderr]    Compiling tungstenite v0.24.0
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling actix-rt v2.11.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-tungstenite v0.24.0
[INFO] [stderr]    Compiling actix-server v2.6.0
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling h2 v0.3.27
[INFO] [stderr]    Compiling actix-codec v0.5.2
[INFO] [stderr]    Compiling zstd-safe v7.2.4
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling actix-http v3.12.0
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling actix-web v4.13.0
[INFO] [stderr]    Compiling hyper-tls v0.6.0
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling bsv-sdk v0.2.5
[INFO] [stderr]    Compiling bsv-auth-actix-middleware v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 18s
[INFO] running `Command { std: "docker" "inspect" "6b8964409488016359041b3fb62c007da924af4f19657fee96c01f6e8aa61ce8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6b8964409488016359041b3fb62c007da924af4f19657fee96c01f6e8aa61ce8", kill_on_drop: false }`
[INFO] [stdout] 6b8964409488016359041b3fb62c007da924af4f19657fee96c01f6e8aa61ce8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 83ebcad5e8dd3a08dd45558f92d71e5b893d3d923281a43944df2fe0c317e327
[INFO] running `Command { std: "docker" "start" "-a" "83ebcad5e8dd3a08dd45558f92d71e5b893d3d923281a43944df2fe0c317e327", kill_on_drop: false }`
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling aws-lc-rs v1.16.2
[INFO] [stderr]    Compiling actix-rt v2.11.0
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling rustls-native-certs v0.8.3
[INFO] [stderr]    Compiling actix-http v3.12.0
[INFO] [stderr]    Compiling actix-server v2.6.0
[INFO] [stderr]    Compiling actix-tls v3.5.0
[INFO] [stderr]    Compiling aws-lc-sys v0.39.1
[INFO] [stderr]    Compiling actix-web v4.13.0
[INFO] [stderr]    Compiling awc v3.8.2
[INFO] [stderr]    Compiling actix-http-test v3.2.0
[INFO] [stderr]    Compiling actix-test v0.1.5
[INFO] [stderr]    Compiling bsv-auth-actix-middleware v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling rustls-webpki v0.103.11
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustls-platform-verifier v0.6.2
[INFO] [stderr]    Compiling hyper-rustls v0.27.8
[INFO] [stderr]    Compiling reqwest v0.13.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 07s
[INFO] running `Command { std: "docker" "inspect" "83ebcad5e8dd3a08dd45558f92d71e5b893d3d923281a43944df2fe0c317e327", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "83ebcad5e8dd3a08dd45558f92d71e5b893d3d923281a43944df2fe0c317e327", kill_on_drop: false }`
[INFO] [stdout] 83ebcad5e8dd3a08dd45558f92d71e5b893d3d923281a43944df2fe0c317e327
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 88afe5349bd5a97b5d0ddcb0b78030a7ced01349798bb8fe1c5f60fca22cb310
[INFO] running `Command { std: "docker" "start" "-a" "88afe5349bd5a97b5d0ddcb0b78030a7ced01349798bb8fe1c5f60fca22cb310", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.50s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/bsv_auth_actix_middleware-96ce70f3ddde8b82)
[INFO] [stdout] 
[INFO] [stdout] running 82 tests
[INFO] [stdout] test config::tests::test_log_level_defaults_to_none ... ok
[INFO] [stdout] test config::tests::test_allow_unauthenticated_can_be_set_to_true ... ok
[INFO] [stdout] test config::tests::test_builder_with_wallet_succeeds ... ok
[INFO] [stdout] test config::tests::test_allow_unauthenticated_defaults_to_false ... ok
[INFO] [stdout] test config::tests::test_log_level_can_be_set_via_builder ... ok
[INFO] [stdout] test config::tests::test_on_certificates_received_can_be_set ... ok
[INFO] [stdout] test config::tests::test_tracing_compiles ... ok
[INFO] [stdout] test config::tests::test_on_certificates_received_defaults_to_none ... ok
[INFO] [stdout] test error::tests::test_bsvsdk_not_authenticated_returns_401 ... ok
[INFO] [stdout] test config::tests::test_session_manager_can_be_set ... ok
[INFO] [stdout] test config::tests::test_default_builder ... ok
[INFO] [stdout] test error::tests::test_bsvsdk_timeout_returns_408 ... ok
[INFO] [stdout] test error::tests::test_certificate_timeout_display ... ok
[INFO] [stdout] test certificate::tests::test_gate_register_returns_notify ... ok
[INFO] [stdout] test error::tests::test_config_display ... ok
[INFO] [stdout] test config::tests::test_certificates_to_request_can_be_set ... ok
[INFO] [stdout] test error::tests::test_certificate_timeout_returns_408 ... ok
[INFO] [stdout] test certificate::tests::test_gate_release_unknown_key_does_not_panic ... ok
[INFO] [stdout] test certificate::tests::test_listener_exits_when_channels_close ... ok
[INFO] [stdout] test error::tests::test_config_variant_returns_500_and_err_config ... ok
[INFO] [stdout] test certificate::tests::test_listener_releases_gate_on_certificate ... ok
[INFO] [stdout] test error::tests::test_error_response_body_format_payload ... ok
[INFO] [stdout] test error::tests::test_error_response_body_format_transport ... ok
[INFO] [stdout] test error::tests::test_error_response_body_format_not_authenticated ... ok
[INFO] [stdout] test error::tests::test_from_bsv_auth_error ... ok
[INFO] [stdout] test error::tests::test_certificate_timeout_error_response_body_matches_ts ... ok
[INFO] [stdout] test error::tests::test_payload_display ... ok
[INFO] [stdout] test error::tests::test_response_signing_failed_display_is_inner_reason ... ok
[INFO] [stdout] test error::tests::test_transport_variant_returns_500_and_err_transport ... ok
[INFO] [stdout] test error::tests::test_unauthorized_display ... ok
[INFO] [stdout] test extractor::tests::test_authenticated_clone ... ok
[INFO] [stdout] test error::tests::test_response_signing_failed_error_response_matches_ts_spec ... ok
[INFO] [stdout] test error::tests::test_transport_display ... ok
[INFO] [stdout] test error::tests::test_unauthorized_error_response_matches_ts_spec ... ok
[INFO] [stdout] test helpers::tests::test_extract_auth_headers_all_present ... ok
[INFO] [stdout] test helpers::tests::test_extract_auth_headers_missing_all ... ok
[INFO] [stdout] test extractor::tests::test_from_request_without_authenticated ... ok
[INFO] [stdout] test extractor::tests::test_from_request_with_authenticated ... ok
[INFO] [stdout] test config::tests::test_builder_without_wallet_returns_error ... ok
[INFO] [stdout] test helpers::tests::test_extract_auth_headers_missing_one ... ok
[INFO] [stdout] test helpers::tests::test_build_auth_message_basic ... ok
[INFO] [stdout] test payload::tests::test_request_headers_exclude_x_bsv_auth ... ok
[INFO] [stdout] test helpers::tests::test_payload_from_bytes_roundtrip ... ok
[INFO] [stdout] test payload::tests::test_request_headers_include_authorization ... ok
[INFO] [stdout] test payload::tests::test_request_headers_include_x_bsv_topic ... ok
[INFO] [stdout] test payload::tests::test_request_headers_include_content_type_normalized ... ok
[INFO] [stdout] test error::tests::test_error_response_body_format_config ... ok
[INFO] [stdout] test payload::tests::test_response_headers_exclude_x_bsv_auth ... ok
[INFO] [stdout] test payload::tests::test_response_headers_include_authorization ... ok
[INFO] [stdout] test payload::tests::test_response_headers_include_x_bsv_topic ... ok
[INFO] [stdout] test payload::tests::test_serialize_request_payload_fixture ... ok
[INFO] [stdout] test payload::tests::test_request_headers_sorted_alphabetically ... ok
[INFO] [stdout] test payload::tests::test_response_headers_sorted_alphabetically ... ok
[INFO] [stdout] test payload::tests::test_serialize_request_payload_with_query_and_body ... ok
[INFO] [stdout] test payload::tests::test_serialize_response_payload_fixture ... ok
[INFO] [stdout] test certificate::tests::test_gate_register_same_key_returns_same_notify ... ok
[INFO] [stdout] test payload::tests::test_response_headers_exclude_content_type ... ok
[INFO] [stdout] test payload::tests::test_varint_253_writes_fd_prefix ... ok
[INFO] [stdout] test certificate::tests::test_gate_release_wakes_waiter ... ok
[INFO] [stdout] test payload::tests::test_varint_65535_writes_fd_prefix ... ok
[INFO] [stdout] test payload::tests::test_serialize_response_payload_no_body ... ok
[INFO] [stdout] test payload::tests::test_varint_252_writes_single_byte ... ok
[INFO] [stdout] test payload::tests::test_varint_zero_writes_zero ... ok
[INFO] [stdout] test transport::tests::test_default_timeout_is_30s ... ok
[INFO] [stdout] test transport::tests::test_oneshot_correlation ... ok
[INFO] [stdout] test transport::tests::test_send_no_pending ... ok
[INFO] [stdout] test payload::tests::test_varint_65536_writes_fe_prefix ... ok
[INFO] [stdout] test payload::tests::test_varint_negative_writes_twos_complement_u64 ... ok
[INFO] [stdout] test transport::tests::test_send_no_nonce_returns_error ... ok
[INFO] [stdout] test transport::tests::test_transport_send_subscribe ... ok
[INFO] [stdout] test config::tests::test_try_init_tracing_noop_when_level_is_none ... ok
[INFO] [stdout] test error::tests::test_bsvsdk_auth_failed_returns_401 ... ok
[INFO] [stdout] test error::tests::test_bsvsdk_invalid_signature_returns_401 ... ok
[INFO] [stdout] test error::tests::test_bsvsdk_other_returns_500 ... ok
[INFO] [stdout] test transport::tests::test_peer_shared_state ... ok
[INFO] [stdout] test certificate::tests::test_listener_invokes_callback_on_certificate ... ok
[INFO] [stdout] test transport::tests::test_register_pending_times_out_after_duration ... ok
[INFO] [stdout] test transport::tests::test_reregister_cancels_old_timeout ... ok
[INFO] [stdout] test transport::tests::test_subscribe_once - should panic ... ok
[INFO] [stdout] test certificate::tests::test_listener_handles_callback_panic_gracefully ... ok
[INFO] [stdout] test transport::tests::test_timeout_is_aborted_on_successful_send ... ok
[INFO] [stdout] test middleware::tests::response_signing_handles_concurrent_requests_for_same_session ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 82 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.66s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/certificate_tests.rs (/opt/rustwide/target/debug/deps/certificate_tests-cd2f79f8202ed04f)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_empty_cert_response_returns_400 ... ok
[INFO] [stdout] test test_cert_response_with_missing_certs_field_returns_400 ... ok
[INFO] [stdout] test test_cert_request_flow ... ok
[INFO] [stdout] test test_cert_protected_endpoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.49s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-b74d31efa3da0a03)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test test_03_post_plain_text ... ok
[INFO] [stdout] test test_12_server_restart ... ok
[INFO] [stdout] test edge_case_c_json_content_object_body ... ok
[INFO] [stdout] test test_07_put_with_json ... ok
[INFO] [stdout] test test_10_query_parameters ... ok
[INFO] [stdout] test test_08_delete ... ok
[INFO] [stdout] test test_14_stale_session_recovery ... ok
[INFO] [stdout] test test_13_charset_injection ... ok
[INFO] [stdout] test test_05_simple_get ... ok
[INFO] [stdout] test edge_case_b_json_content_undefined_body ... ok
[INFO] [stdout] test test_01_simple_post_with_json ... FAILED
[INFO] [stdout] test test_02_post_url_encoded ... FAILED
[INFO] [stdout] test test_09_large_binary_upload ... ok
[INFO] [stdout] test test_04_post_binary ... ok
[INFO] [stdout] test test_15_multiple_requests_survive_session_reset ... ok
[INFO] [stdout] test edge_case_a_no_content_type ... ok
[INFO] [stdout] test test_11_custom_headers ... ok
[INFO] [stdout] test test_unauthenticated_request_returns_ts_parity_401 ... ok
[INFO] [stdout] test test_01b_error_500 ... ok
[INFO] [stdout] test test_concurrent_authenticated_requests ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_01_simple_post_with_json stdout ----
[INFO] [stdout] [test_01] POST http://127.0.0.1:36321/other-endpoint with JSON body
[INFO] [stdout] [2m2026-06-13T20:13:55.465162Z[0m [34mDEBUG[0m [2mreqwest::connect[0m[2m:[0m starting new connection: http://127.0.0.1:36321/
[INFO] [stdout] [2m2026-06-13T20:13:55.465298Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::connect::http[0m[2m:[0m connecting to 127.0.0.1:36321
[INFO] [stdout] [2m2026-06-13T20:13:55.465567Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::connect::http[0m[2m:[0m connected to 127.0.0.1:36321
[INFO] [stdout] [2m2026-06-13T20:13:56.971543Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::pool[0m[2m:[0m pooling idle connection for ("http", 127.0.0.1:36321)
[INFO] [stdout] [2m2026-06-13T20:13:57.210770Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::pool[0m[2m:[0m reuse idle connection for ("http", 127.0.0.1:36321)
[INFO] [stdout] 
[INFO] [stdout] thread 'test_01_simple_post_with_json' (122) panicked at tests/integration_tests.rs:65:10:
[INFO] [stdout] auth fetch should succeed: TransportError("HTTP request to http://127.0.0.1:36321/other-endpoint failed: error sending request for url (http://127.0.0.1:36321/other-endpoint)")
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x618dab30d208 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x618dab30d208 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x618dab30d208 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x618dab30d208 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x618dab3263ca - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x618dab3263ca - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x618dab312912 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x618dab312912 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x618dab2eae06 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x618dab2eae06 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x618dab304de9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x618daa6652fc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x618daa6652fc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x618dab305062 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x618dab305062 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x618dab2eaec2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x618dab2dfe99 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x618dab2ebd9d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x618dab326c3c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x618dab326982 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x618da9f20565 - <core[28b146924c2f19c6]::result::Result<bsv[d7f6d25ded9fea5f]::auth::clients::auth_fetch::AuthFetchResponse, bsv[d7f6d25ded9fea5f]::auth::error::AuthError>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x618da9fb99fa - integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:65:10
[INFO] [stdout]   22:     0x618daa02f71f - <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:1072:44
[INFO] [stdout]   23:     0x618daa025c30 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::with::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:823:13
[INFO] [stdout]   24:     0x618da9f82e35 - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::task::local::LocalData>>::try_with::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::with<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[28b146924c2f19c6]::task::poll::Poll<()>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   25:     0x618da9f80efe - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::task::local::LocalData>>::with::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::with<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[28b146924c2f19c6]::task::poll::Poll<()>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   26:     0x618daa014d46 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::with::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:821:17
[INFO] [stdout]   27:     0x618daa0358f6 - <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:1062:22
[INFO] [stdout]   28:     0x618daa028532 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until::<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:719:19
[INFO] [stdout]   29:     0x618da9effc5d - <core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   30:     0x618da9f0039d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   31:     0x618da9e2909d - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   32:     0x618da9e2607b - tokio[9213a43f038e1cbc]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   33:     0x618da9e2607b - tokio[9213a43f038e1cbc]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   34:     0x618da9e2607b - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   35:     0x618da9dd97c0 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   36:     0x618da9e0fbfd - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   37:     0x618da9e05d04 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   38:     0x618da9edb87b - <tokio[9213a43f038e1cbc]::runtime::context::scoped::Scoped<tokio[9213a43f038e1cbc]::runtime::scheduler::Context>>::set::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   39:     0x618daa02c4a9 - tokio[9213a43f038e1cbc]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:38
[INFO] [stdout]   40:     0x618da9f88bc2 - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::runtime::context::Context>>::try_with::<tokio[9213a43f038e1cbc]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   41:     0x618da9f844ee - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::runtime::context::Context>>::with::<tokio[9213a43f038e1cbc]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   42:     0x618daa01dd5d - tokio[9213a43f038e1cbc]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:17
[INFO] [stdout]   43:     0x618da9df1250 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   44:     0x618da9df8b26 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   45:     0x618da9dfc980 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   46:     0x618da9ffc5c3 - tokio[9213a43f038e1cbc]::runtime::context::runtime::enter_runtime::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   47:     0x618da9dd5293 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   48:     0x618da9ebb681 - <tokio[9213a43f038e1cbc]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   49:     0x618da9ec0bba - <tokio[9213a43f038e1cbc]::runtime::runtime::Runtime>::block_on::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:343:18
[INFO] [stdout]   50:     0x618daa0157fc - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:676:12
[INFO] [stdout]   51:     0x618da9f9e107 - <actix_rt[731b71ad4c0e4e5d]::runtime::Runtime>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/actix-rt-2.11.0/src/runtime.rs:138:20
[INFO] [stdout]   52:     0x618da9ff4df7 - <actix_rt[731b71ad4c0e4e5d]::system::SystemRunner>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/actix-rt-2.11.0/src/system.rs:240:17
[INFO] [stdout]   53:     0x618da9fc8978 - integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:48:1
[INFO] [stdout]   54:     0x618da9fadff7 - integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:49:41
[INFO] [stdout]   55:     0x618da9e9d3a6 - <integration_tests[eef237fb6d0f7ff4]::test_01_simple_post_with_json::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   56:     0x618daa6583db - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   57:     0x618daa6583db - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   58:     0x618daa665c55 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   59:     0x618daa665c55 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   60:     0x618daa665c55 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   61:     0x618daa665c55 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   62:     0x618daa665c55 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   63:     0x618daa665c55 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   64:     0x618daa665c55 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   65:     0x618daa6607b4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   66:     0x618daa6607b4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   67:     0x618daa668cd2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   68:     0x618daa668cd2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   69:     0x618daa668cd2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   70:     0x618daa668cd2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   71:     0x618daa668cd2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   72:     0x618daa668cd2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   73:     0x618daa668cd2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   74:     0x618dab30c7ef - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   75:     0x618dab30c7ef - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   76:     0x76134ce49aa4 - <unknown>
[INFO] [stdout]   77:     0x76134ced6a64 - clone
[INFO] [stdout]   78:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_02_post_url_encoded stdout ----
[INFO] [stdout] [2m2026-06-13T20:13:54.096695Z[0m [32m INFO[0m [2mbsv_auth_actix_middleware::config[0m[2m:[0m auth middleware configured [3mallow_unauthenticated[0m[2m=[0mfalse [3mlog_level[0m[2m=[0mNone
[INFO] [stdout] [2m2026-06-13T20:13:54.097349Z[0m [32m INFO[0m [2mactix_server::builder[0m[2m:[0m starting 1 workers
[INFO] [stdout] [2m2026-06-13T20:13:54.097401Z[0m [32m INFO[0m [2mactix_server::server[0m[2m:[0m Actix runtime found; starting in Actix runtime
[INFO] [stdout] [2m2026-06-13T20:13:54.097419Z[0m [32m INFO[0m [2mactix_server::server[0m[2m:[0m starting service: "test", workers: 1, listening on: 127.0.0.1:36321
[INFO] [stdout] Test server started at http://127.0.0.1:36321
[INFO] [stdout] [test_02] POST http://127.0.0.1:36321/other-endpoint with URL-encoded body
[INFO] [stdout] [2m2026-06-13T20:13:55.304872Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:55.304987Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=028560db839531f2164785e6367c561428bdbb9728159166f0e5fdd9fb0a8a9d4f
[INFO] [stdout] [2m2026-06-13T20:13:55.485927Z[0m [34mDEBUG[0m [2mreqwest::connect[0m[2m:[0m starting new connection: http://127.0.0.1:36321/
[INFO] [stdout] [2m2026-06-13T20:13:55.486023Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::connect::http[0m[2m:[0m connecting to 127.0.0.1:36321
[INFO] [stdout] [2m2026-06-13T20:13:55.486366Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::connect::http[0m[2m:[0m connected to 127.0.0.1:36321
[INFO] [stdout] [2m2026-06-13T20:13:55.501853Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:55.513025Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:55.513124Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03182cdd9f255085406a5ef145820da4b32ceebe86a90c5837856e088d8f11e450
[INFO] [stdout] [2m2026-06-13T20:13:55.786084Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:55.786550Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:55.786634Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=036453759f0456ee5f5a9ac879c8164dd1cb0a113ee09e92bb84406a7bb0abec1c
[INFO] [stdout] [2m2026-06-13T20:13:55.941892Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:55.944389Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:55.944451Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=02ee73f0ce8fbdf3dbfe7ebdeec0b5cb2adf8e996d3572d5568c53dcba2d44b6cc
[INFO] [stdout] [2m2026-06-13T20:13:56.146157Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.149525Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.149591Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03ab310582061e70a41c4302ef64a959ab44fce4d6812e6b1705623a7abb24f618
[INFO] [stdout] [2m2026-06-13T20:13:56.264780Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.268408Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.268478Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03e25a0ce5c6875075a81bcefc3dddcfed45ed5ee1d5d9312fa15ab8a36a2b0fbd
[INFO] [stdout] [2m2026-06-13T20:13:56.438167Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.438639Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.438710Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=039beef918498638ad0e8a4bc17e7ed1f68e2d3567b7f1beaaf4761f0012c88e4d
[INFO] [stdout] [2m2026-06-13T20:13:56.642918Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.643416Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.643497Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=02ed023d47b48e26dec6c92846dbfd0c3a49b466e0d2ca7cac742b10d7d727c04c
[INFO] [stdout] [2m2026-06-13T20:13:56.781540Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.788461Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.788551Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03477148a5a05f2ce72ed85f75a7a7a5cb4c1017eea038b1ecd34303175e11a413
[INFO] [stdout] [2m2026-06-13T20:13:56.969998Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:56.970482Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:56.970554Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=02fc3650f7d36f1a663ef2694829c48b2654a8876001304e6ec571eb6a9cc34457
[INFO] [stdout] [2m2026-06-13T20:13:57.174977Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:57.175631Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::pool[0m[2m:[0m pooling idle connection for ("http", 127.0.0.1:36321)
[INFO] [stdout] [2m2026-06-13T20:13:57.175837Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=028560db839531f2164785e6367c561428bdbb9728159166f0e5fdd9fb0a8a9d4f)
[INFO] [stdout] [2m2026-06-13T20:13:57.175926Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=POST path=/other-endpoint query= body_len=36 nonce_len=32 request_id=qpegFbsU8SfHnDRFgVUs6xHHNGEhtxFixnJlBqbic4s= nonce=Y5vowB3upHfgEJflzOLRYz1/w0rE+ag0mKOUl+5JqEE= your_nonce=2gb4kCkF0USdAxIE9kTIKFO/BTs2a0+Q1clgMAdQBzRvmc6WaJSOskftuYTRfoYT payload_len=157
[INFO] [stdout] [2m2026-06-13T20:13:57.175976Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:57.175991Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:57.279663Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] POST /other-endpoint -- body length: 36
[INFO] [stdout] [2m2026-06-13T20:13:57.279963Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:57.279993Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:57.315111Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:57.315163Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:57.315663Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=03182cdd9f255085406a5ef145820da4b32ceebe86a90c5837856e088d8f11e450)
[INFO] [stdout] [2m2026-06-13T20:13:57.315778Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=POST path=/other-endpoint query= body_len=2 nonce_len=32 request_id=SDLydwMdjucKvJY6nP4ngWJzz56iEsP54j5E3pnk714= nonce=a8dEOUE6DRVgTk5JAEt9uQJOBVyo5Ev/1bVgWmhkpqU= your_nonce=tBYvjIpqWTSj0p9QnKwskTlZfG7vCSF1MvP05MsTZc8eaJV4FWN6NhO+zRaQ8Otw payload_len=96
[INFO] [stdout] [2m2026-06-13T20:13:57.315841Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:57.315861Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:57.421874Z[0m [34mDEBUG[0m [2mhyper_util::client::legacy::pool[0m[2m:[0m reuse idle connection for ("http", 127.0.0.1:36321)
[INFO] [stdout] [2m2026-06-13T20:13:57.513460Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] POST /other-endpoint -- body length: 2
[INFO] [stdout] [2m2026-06-13T20:13:57.513732Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:57.513762Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:57.558855Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:57.558906Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:57.561375Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:57.561456Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=02f7c9fadc57d9e15431205d9b7cffa22380f071d64a12e0bddf0b46b3abf10ba0
[INFO] [stdout] [2m2026-06-13T20:13:57.789646Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:57.789990Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:57.790048Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03b19f15839fdeba4970a7dd0301873e6d2487e111ed13b1c13f02b80f9ff0764f
[INFO] [stdout] [2m2026-06-13T20:13:57.921236Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:57.921578Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:57.921652Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=03d20fcfa67e2988f3f4ee9e0c11325819109a358543c8fa91a6198b26eabdae47
[INFO] [stdout] [2m2026-06-13T20:13:58.213513Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:58.213985Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:58.214066Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=033da9a7f899f92d8e5f77554c7dc4b684714f183a91ac63684c9a9d133f33eeeb
[INFO] [stdout] [2m2026-06-13T20:13:58.496648Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:58.497418Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m BRC-31 handshake request at /.well-known/auth
[INFO] [stdout] [2m2026-06-13T20:13:58.497499Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Auth message received at /.well-known/auth: type=InitialRequest, identity_key=02360f586e5dc3769d273f1950d61886f44411273d5116dfe4aff6896cecf3611a
[INFO] [stdout] [2m2026-06-13T20:13:58.640778Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Handshake response ready: identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:58.641457Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=036453759f0456ee5f5a9ac879c8164dd1cb0a113ee09e92bb84406a7bb0abec1c)
[INFO] [stdout] [2m2026-06-13T20:13:58.641552Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=PUT path=/put-endpoint query= body_len=33 nonce_len=32 request_id=VldY9go4qP2tGAH2/6KMR9di2zuZH1DKz87LXPDnIvk= nonce=LnBBvN7pE8/6PH/g9WcnnV+9LPDJFCZSqfo2eID5bQE= your_nonce=aHdY3soQbTznmqIbWNORWcFcgnSr9nku1Izn7ltIqAdksrovqsKPRQdHffC+GTfq payload_len=157
[INFO] [stdout] [2m2026-06-13T20:13:58.641604Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:58.641619Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:58.874067Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] PUT /put-endpoint -- body length: 33
[INFO] [stdout] [2m2026-06-13T20:13:58.874358Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:58.874386Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:58.969063Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:58.969094Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:58.970559Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=02ee73f0ce8fbdf3dbfe7ebdeec0b5cb2adf8e996d3572d5568c53dcba2d44b6cc)
[INFO] [stdout] [2m2026-06-13T20:13:58.970638Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=GET path=/query-endpoint query=param1=value1&param2=value2 body_len=0 nonce_len=32 request_id=Oxwl5AXVqLhm3y5ybhSOolcL20JxjkhC6X9N55KI5Ug= nonce=ySg4sF1Ur0SsVUlbXT5krWf9L5xG01Sy85NGTSh9TV8= your_nonce=cEhYpwC4W5tvNl/QINPy6wiKAyZoiGuW6l5r8AlAHbuQHAo1t+BzmMG5oiG3w9RG payload_len=91
[INFO] [stdout] [2m2026-06-13T20:13:58.970697Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:58.970716Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:59.151470Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] GET /query-endpoint -- query: param1=value1&param2=value2
[INFO] [stdout] [2m2026-06-13T20:13:59.151719Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.151745Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:59.244103Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:59.244158Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:59.244673Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=03ab310582061e70a41c4302ef64a959ab44fce4d6812e6b1705623a7abb24f618)
[INFO] [stdout] [2m2026-06-13T20:13:59.244753Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=DELETE path=/delete-endpoint query= body_len=0 nonce_len=32 request_id=zWE3B36hqc7+CJYtJ8+CdMFoHq/oZyAj/hBqyRtVBQc= nonce=s34r8CUAuG07X7poc2mkX452ahhp8V7ZXz+i3pEFsA0= your_nonce=k+g1iyLXiqbP2SCCJuDFWdphFPwuirxOZOXWjEnwO5KvTsgcW6wJnqlFeFh4OtbY payload_len=108
[INFO] [stdout] [2m2026-06-13T20:13:59.244809Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.244828Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:59.612602Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] DELETE /delete-endpoint
[INFO] [stdout] [2m2026-06-13T20:13:59.612827Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.612849Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:59.654958Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:59.655006Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:59.658657Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=03e25a0ce5c6875075a81bcefc3dddcfed45ed5ee1d5d9312fa15ab8a36a2b0fbd)
[INFO] [stdout] [2m2026-06-13T20:13:59.658774Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=POST path=/other-endpoint query= body_len=54 nonce_len=32 request_id=aQeJ/O8+IdVo1bZOnBP3K4rOtLP3Epts7tx56LnnHGE= nonce=0USQGcjd7EB2xN0urjVGBI3J6gt4pbFD8ByILL6S+/M= your_nonce=zG8F9hv5f+XOYKj+ofVd5+xIj6nj2wq43HrceWMOUUZgOxvOSXlh+7pPgtiqsooT payload_len=148
[INFO] [stdout] [2m2026-06-13T20:13:59.658827Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.658845Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:13:59.910841Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] POST /other-endpoint -- body length: 54
[INFO] [stdout] [2m2026-06-13T20:13:59.911112Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.911141Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:13:59.965618Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:13:59.965670Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:13:59.966143Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=039beef918498638ad0e8a4bc17e7ed1f68e2d3567b7f1beaaf4761f0012c88e4d)
[INFO] [stdout] [2m2026-06-13T20:13:59.966232Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=GET path=/ query= body_len=0 nonce_len=32 request_id=ERlTgXvXtaXJ5J/GaNjQ6FpDWTMLLguq5tKseerSnT0= nonce=qNdQKCHSEEZtgHWP1QSazXxq+wuZZ46EARBVoXJHrHM= your_nonce=h8J6Osa+eI0iz7lgCmZnc0YiAz/SWNlaFc8XE8Tad1SDfKt7xvtwfyLM8AVugjx9 payload_len=57
[INFO] [stdout] [2m2026-06-13T20:13:59.967324Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:13:59.967343Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:14:00.167951Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] GET / -- Hello, world!
[INFO] [stdout] [2m2026-06-13T20:14:00.168133Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:14:00.168161Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:14:00.201386Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:14:00.201435Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] [2m2026-06-13T20:14:00.201906Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=02ed023d47b48e26dec6c92846dbfd0c3a49b466e0d2ca7cac742b10d7d727c04c)
[INFO] [stdout] [2m2026-06-13T20:14:00.201988Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=POST path=/other-endpoint query= body_len=0 nonce_len=32 request_id=58STMTGhsU11eAOq2+8H/yMnKkGCfRnA5AlbNR3fmdU= nonce=XpG3Q4L8shSWwjeWtj9iCgUEX36BSSsGZjXsS1Hzk3c= your_nonce=2zS25d7jTFH8eXkue7U/Z50a7Cfxh//Hy4ud/fclhzbRAhC93vXgHFbzHx4Tgsj2 payload_len=102
[INFO] [stdout] [2m2026-06-13T20:14:00.202042Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:14:00.202062Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] [2m2026-06-13T20:14:00.491222Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: dispatch complete, releasing lock
[INFO] [stdout] [handler] POST /other-endpoint -- body length: 0
[INFO] [stdout] [2m2026-06-13T20:14:00.492518Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:14:00.492548Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: acquired peer lock, calling create_general_message
[INFO] [stdout] [2m2026-06-13T20:14:00.539857Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m response_signing: create_general_message completed
[INFO] [stdout] [2m2026-06-13T20:14:00.539901Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Response signed for identity_key=02851a3971a1fbf02aa324572bc562beda46fd54b423fa68653e43fd2fc6395afc
[INFO] [stdout] 
[INFO] [stdout] thread 'test_02_post_url_encoded' (124) panicked at tests/integration_tests.rs:187:10:
[INFO] [stdout] auth fetch should succeed: TransportError("HTTP request to http://127.0.0.1:36321/other-endpoint failed: error sending request for url (http://127.0.0.1:36321/other-endpoint)")
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x618dab30d208 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x618dab30d208 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x618dab30d208 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x618dab30d208 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x618dab3263ca - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x618dab3263ca - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x618dab312912 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x618dab312912 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x618dab2eae06 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x618dab2eae06 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x618dab304de9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x618daa6652fc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x618daa6652fc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x618dab305062 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x618dab305062 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x618dab2eaec2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x618dab2dfe99 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x618dab2ebd9d - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x618dab326c3c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x618dab326982 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x618da9f20565 - <core[28b146924c2f19c6]::result::Result<bsv[d7f6d25ded9fea5f]::auth::clients::auth_fetch::AuthFetchResponse, bsv[d7f6d25ded9fea5f]::auth::error::AuthError>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x618da9fb5c1b - integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:187:10
[INFO] [stdout]   22:     0x618daa02f0df - <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:1072:44
[INFO] [stdout]   23:     0x618daa025780 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::with::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:823:13
[INFO] [stdout]   24:     0x618da9f82935 - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::task::local::LocalData>>::try_with::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::with<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[28b146924c2f19c6]::task::poll::Poll<()>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   25:     0x618da9f80d6e - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::task::local::LocalData>>::with::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::with<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[28b146924c2f19c6]::task::poll::Poll<()>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   26:     0x618daa014c06 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::with::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:821:17
[INFO] [stdout]   27:     0x618daa035766 - <tokio[9213a43f038e1cbc]::task::local::RunUntil<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:1062:22
[INFO] [stdout]   28:     0x618daa027952 - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until::<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:719:19
[INFO] [stdout]   29:     0x618da9effb1d - <core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   30:     0x618da9f0025d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   31:     0x618da9e28f5d - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   32:     0x618da9e2585b - tokio[9213a43f038e1cbc]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   33:     0x618da9e2585b - tokio[9213a43f038e1cbc]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   34:     0x618da9e2585b - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   35:     0x618da9dd8820 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   36:     0x618da9e0c54d - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   37:     0x618da9e05c64 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   38:     0x618da9edb1eb - <tokio[9213a43f038e1cbc]::runtime::context::scoped::Scoped<tokio[9213a43f038e1cbc]::runtime::scheduler::Context>>::set::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   39:     0x618daa02c369 - tokio[9213a43f038e1cbc]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:38
[INFO] [stdout]   40:     0x618da9f88582 - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::runtime::context::Context>>::try_with::<tokio[9213a43f038e1cbc]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   41:     0x618da9f8430e - <std[929523f510218772]::thread::local::LocalKey<tokio[9213a43f038e1cbc]::runtime::context::Context>>::with::<tokio[9213a43f038e1cbc]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   42:     0x618daa01dc1d - tokio[9213a43f038e1cbc]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:17
[INFO] [stdout]   43:     0x618da9defdb0 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   44:     0x618da9df88a6 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   45:     0x618da9dfb8f0 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   46:     0x618da9ffbe93 - tokio[9213a43f038e1cbc]::runtime::context::runtime::enter_runtime::<<tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   47:     0x618da9dd4f23 - <tokio[9213a43f038e1cbc]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   48:     0x618da9ebb131 - <tokio[9213a43f038e1cbc]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<alloc[95e62e727ddf147d]::boxed::Box<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   49:     0x618da9ebfdfa - <tokio[9213a43f038e1cbc]::runtime::runtime::Runtime>::block_on::<<tokio[9213a43f038e1cbc]::task::local::LocalSet>::run_until<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:343:18
[INFO] [stdout]   50:     0x618daa0154dc - <tokio[9213a43f038e1cbc]::task::local::LocalSet>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/local.rs:676:12
[INFO] [stdout]   51:     0x618da9f9df77 - <actix_rt[731b71ad4c0e4e5d]::runtime::Runtime>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/actix-rt-2.11.0/src/runtime.rs:138:20
[INFO] [stdout]   52:     0x618da9ff4ca7 - <actix_rt[731b71ad4c0e4e5d]::system::SystemRunner>::block_on::<integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/actix-rt-2.11.0/src/system.rs:240:17
[INFO] [stdout]   53:     0x618da9fc86b8 - integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:162:1
[INFO] [stdout]   54:     0x618da9fadf07 - integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration_tests.rs:163:36
[INFO] [stdout]   55:     0x618da9e9d266 - <integration_tests[eef237fb6d0f7ff4]::test_02_post_url_encoded::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   56:     0x618daa6583db - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   57:     0x618daa6583db - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   58:     0x618daa665c55 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   59:     0x618daa665c55 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   60:     0x618daa665c55 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   61:     0x618daa665c55 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   62:     0x618daa665c55 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   63:     0x618daa665c55 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   64:     0x618daa665c55 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   65:     0x618daa6607b4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   66:     0x618daa6607b4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   67:     0x618daa668cd2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   68:     0x618daa668cd2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   69:     0x618daa668cd2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   70:     0x618daa668cd2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   71:     0x618daa668cd2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   72:     0x618daa668cd2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   73:     0x618daa668cd2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   74:     0x618dab30c7ef - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   75:     0x618dab30c7ef - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   76:     0x76134ce49aa4 - <unknown>
[INFO] [stdout]   77:     0x76134ced6a64 - clone
[INFO] [stdout]   78:                0x0 - <unknown>
[INFO] [stdout] [2m2026-06-13T20:14:00.541618Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m Authenticated request detected (identity_key=02f7c9fadc57d9e15431205d9b7cffa22380f071d64a12e0bddf0b46b3abf10ba0)
[INFO] [stdout] [2m2026-06-13T20:14:00.827426Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::helpers[0m[2m:[0m build_auth_message: method=POST path=/large-upload query= body_len=30 nonce_len=32 request_id=biA1BcddQddKW6diPtxJO6s15r7KB9E8J4iT3K5ZgrM= nonce=qKjacryqQazsZ73IrqBJN+lYDWO1n36RTro1OFprRb4= your_nonce=MDYU2A9sQQO2N7BLOtTLdjb4NbCJmUY8Tne2DmboCBQIoCp+2Oz1hVgXm24O1/Lx payload_len=130
[INFO] [stdout] [2m2026-06-13T20:14:00.827504Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: waiting for peer lock
[INFO] [stdout] [2m2026-06-13T20:14:00.827528Z[0m [34mDEBUG[0m [2mbsv_auth_actix_middleware::middleware[0m[2m:[0m request_verify: acquired peer lock, dispatching message
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_01_simple_post_with_json
[INFO] [stdout]     test_02_post_url_encoded
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 18 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.58s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test integration_tests`
[INFO] running `Command { std: "docker" "inspect" "88afe5349bd5a97b5d0ddcb0b78030a7ced01349798bb8fe1c5f60fca22cb310", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "88afe5349bd5a97b5d0ddcb0b78030a7ced01349798bb8fe1c5f60fca22cb310", kill_on_drop: false }`
[INFO] [stdout] 88afe5349bd5a97b5d0ddcb0b78030a7ced01349798bb8fe1c5f60fca22cb310
